**** dT 0.000 * top TEST ../../../../bin/vinyltest/vtest2/tests/a02024.vtc starting **** top extmacro def pkg_version=trunk **** top extmacro def pkg_branch=trunk **** top extmacro def pwd=/tmp/vtest.gcc13_m32_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest **** top extmacro def date(...) **** top extmacro def string(...) **** top extmacro def localhost=127.0.0.1 **** top extmacro def bad_backend=127.0.0.1:42368 **** top extmacro def listen_addr=127.0.0.1:0 **** top extmacro def bad_ip=192.0.2.255 **** top extmacro def topbuild=/tmp/vtest.gcc13_m32_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub **** top extmacro def topsrc=/tmp/vtest.gcc13_m32_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/../.. **** top macro def testdir=/tmp/vtest.gcc13_m32_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/../../../../bin/vinyltest/vtest2/tests **** top macro def tmpdir=/tmp/vtest.gcc13_m32_IPv4_uffix/_vtest_tmp/vtc.946706.7fcc08de **** top macro def vtcid=vtc.946706.7fcc08de ** top === vtest "Write a body to a file" * top VTEST Write a body to a file ** top === server s1 { ** s1 Starting server **** dT 0.001 **** s1 macro def s1_addr=127.0.0.1 **** s1 macro def s1_port=47154 **** s1 macro def s1_sock=127.0.0.1:47154 * s1 Listen on 127.0.0.1:47154 ** top === client c1 -connect ${s1_sock} { ** c1 Starting client ** s1 Started on 127.0.0.1:47154 (1 iterations) ** c1 Waiting for client ** c1 Started on 127.0.0.1:47154 (1 iterations) *** c1 Connect to 127.0.0.1:47154 *** c1 connected fd 5 from 127.0.0.1 62329 to 127.0.0.1:47154 ** c1 === fatal **** dT 0.002 ** c1 === stream 1 { **** c1 Not in H/2 mode, do what's needed ** c1 === txpri **** c1 txpri|PRI * HTTP/2.0\r **** c1 txpri|\r **** c1 txpri|SM\r **** c1 txpri|\r *** s1 accepted fd 4 127.0.0.1 62329 ** s1 === fatal ** s1 === stream 1 { **** s1 Not in H/2 mode, do what's needed ** s1 === rxpri **** dT 0.012 ** c1 === stream 0 { ** c1 Starting stream 0 (850f290) ** s1 === stream 0 { ** c1 Waiting for stream 0 ** s1 Starting stream 0 (8510428) ** s1 Waiting for stream 0 ** c1.0 === txsettings *** c1.0 tx: stream: 0, type: SETTINGS (4), flags: 0x00, size: 0 ** c1.0 === rxsettings ** s1.0 === txsettings *** s1.0 tx: stream: 0, type: SETTINGS (4), flags: 0x00, size: 0 ** s1.0 === rxsettings *** c1 rx: stream: 0, type: SETTINGS (4), flags: 0x00, size: 0 ** c1.0 === txsettings -ack *** s1 rx: stream: 0, type: SETTINGS (4), flags: 0x00, size: 0 *** c1.0 tx: stream: 0, type: SETTINGS (4), flags: 0x01, size: 0 ** c1.0 === rxsettings ** s1.0 === txsettings -ack *** s1.0 tx: stream: 0, type: SETTINGS (4), flags: 0x01, size: 0 ** s1.0 === rxsettings *** c1 rx: stream: 0, type: SETTINGS (4), flags: 0x01, size: 0 *** c1 flag: ACK *** s1 rx: stream: 0, type: SETTINGS (4), flags: 0x01, size: 0 *** s1 flag: ACK ** c1.0 === expect settings.ack == true ** s1.0 === expect settings.ack == true **** s1.0 EXPECT settings.ack (true) == "true" match **** c1.0 EXPECT settings.ack (true) == "true" match ** c1.0 Ending stream 0 ** s1.0 Ending stream 0 ** s1 Starting stream 1 (85119e0) ** c1 Starting stream 1 (8512a50) ** s1 Waiting for stream 1 ** c1 Waiting for stream 1 ** s1.1 === rxreq ** c1.1 === txreq -req POST -hdr Content-Type text/plain -body request *** c1.1 tx: stream: 1, type: HEADERS (1), flags: 0x04, size: 62 *** c1.1 tx: stream: 1, type: DATA (0), flags: 0x01, size: 7 ** c1.1 === rxresp *** s1 rx: stream: 1, type: HEADERS (1), flags: 0x04, size: 62 *** s1 flag: END_TYPE_HEADERS **** s1 header[ 0]: :method: POST **** s1 header[ 1]: Content-Type: text/plain **** s1 header[ 2]: :path: / **** s1 header[ 3]: :scheme: http *** s1 rx: stream: 1, type: DATA (0), flags: 0x01, size: 7 *** s1 flag: END_STREAM ** s1.1 === expect req.http.Content-Type == "text/plain" **** s1.1 EXPECT req.http.Content-Type (text/plain) == "text/plain" match ** s1.1 === write_body req.txt **** dT 0.013 ** s1.1 === shell {grep -q request req.txt} **** s1.1 shell_cmd|set -e ;exec 2>&1 ; grep -q request req.txt ---- s1.1 popen fails: Resource temporarily unavailable * s1 Aborting execution, test failed *** c1 HTTP2 rx EOF (fd:5 read: Error 0) ---- c1 could not get frame header * c1.1 Aborting execution, test failed ** c1.1 Ending stream 1 *** s1 shutting fd -1 (server run) *** c1 closing fd -1 ---- s1 Shutdown(RD) failed: Bad file number * diag 0.0 Assert error in VTCP_close(), ../../../../lib/libvinyl/vtcp.c line 347: * diag 0.0 Condition(VTCP_Check(i)) not true. (errno=9 Bad file number) * diag 0.0 Backtrace: * diag 0.0 fef230e3: /tmp/vtest.gcc13_m32_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'vbt_execinfo+0x39 [0xfef230e3] * diag 0.0 fef232f4: /tmp/vtest.gcc13_m32_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'VBT_format+0x2d [0xfef232f4] * diag 0.0 fef23362: /tmp/vtest.gcc13_m32_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'VBT_dump+0x66 [0xfef23362] * diag 0.0 807c399: /tmp/vtest.gcc13_m32_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'vtc_log_VAS_Fail+0x65 [0x807c399] * diag 0.0 fef226e9: /tmp/vtest.gcc13_m32_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'VAS_Fail+0x3a [0xfef226e9] * diag 0.0 fef33ef5: /tmp/vtest.gcc13_m32_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/lib/libvinylapi/.libs/libvinylapi.so.3.1.0'VTCP_close+0x5c [0xfef33ef5] * diag 0.0 8063d9a: /tmp/vtest.gcc13_m32_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'client_disc+0x29 [0x8063d9a] * diag 0.0 808a564: /tmp/vtest.gcc13_m32_IPv4_uffix/varnish-cache/vinyl-cache-trunk/_build/sub/bin/vinyltest/.libs/vinyltest'sess_thread+0x1f0 [0x808a564] * diag 0.0 fed9a97f: /lib/libc.so.1'_thrp_setup+0x7f [0xfed9a97f] * diag 0.0 fed9ac30: /lib/libc.so.1'_lwp_start+0x0 [0xfed9ac30] * diag 0.0 # top TEST ../../../../bin/vinyltest/vtest2/tests/a02024.vtc FAILED (0.042) signal=6 FAIL vtest2/tests/a02024.vtc (exit status: 2)